[INFO] cloning repository https://github.com/Jaxton07/OpenJax
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/Jaxton07/OpenJax" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FJaxton07%2FOpenJax", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FJaxton07%2FOpenJax'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] da81a8fb2ec886ec013fd0f4eb1cafbd06ffc935
[INFO] testing Jaxton07/OpenJax against master#cced03bfd61a304243a34504618ecec86c17063f for pr-154065-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FJaxton07%2FOpenJax" "/workspace/builds/worker-7-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-7-tc1/source'...
[INFO] [stderr] done.
[INFO] removed /workspace/builds/worker-7-tc1/source/rust-toolchain.toml
[INFO] started tweaking git repo https://github.com/Jaxton07/OpenJax
[INFO] finished tweaking git repo https://github.com/Jaxton07/OpenJax
[INFO] tweaked toml for git repo https://github.com/Jaxton07/OpenJax written to /workspace/builds/worker-7-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/Jaxton07/OpenJax on toolchain cced03bfd61a304243a34504618ecec86c17063f
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/Jaxton07/OpenJax already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded deranged v0.5.6
[INFO] [stderr]   Downloaded tar v0.4.45
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] f8cb13428086542e31c51e1f5d359a7044e5e97be4307d375c8dc1281fb98d7c
[INFO] running `Command { std: "docker" "start" "-a" "f8cb13428086542e31c51e1f5d359a7044e5e97be4307d375c8dc1281fb98d7c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "f8cb13428086542e31c51e1f5d359a7044e5e97be4307d375c8dc1281fb98d7c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f8cb13428086542e31c51e1f5d359a7044e5e97be4307d375c8dc1281fb98d7c", kill_on_drop: false }`
[INFO] [stdout] f8cb13428086542e31c51e1f5d359a7044e5e97be4307d375c8dc1281fb98d7c
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] dba215846e4207b6a6443039c319f806493414c7ae79bf3569de371e40fe1596
[INFO] running `Command { std: "docker" "start" "-a" "dba215846e4207b6a6443039c319f806493414c7ae79bf3569de371e40fe1596", kill_on_drop: false }`
[INFO] [stderr]    Compiling unicode-ident v1.0.23
[INFO] [stderr]    Compiling libc v0.2.181
[INFO] [stderr]    Compiling shlex v1.3.0
[INFO] [stderr]    Compiling futures-core v0.3.31
[INFO] [stderr]    Compiling futures-task v0.3.31
[INFO] [stderr]    Compiling rustls v0.23.36
[INFO] [stderr]    Compiling unicase v2.9.0
[INFO] [stderr]    Compiling rustix v1.1.3
[INFO] [stderr]    Compiling proc-macro2 v1.0.106
[INFO] [stderr]    Compiling getrandom v0.4.1
[INFO] [stderr]    Compiling rustix v0.38.44
[INFO] [stderr]    Compiling time-macros v0.2.27
[INFO] [stderr]    Compiling cc v1.2.55
[INFO] [stderr]    Compiling sync_wrapper v1.0.2
[INFO] [stderr]    Compiling futures-channel v0.3.31
[INFO] [stderr]    Compiling http-body-util v0.1.3
[INFO] [stderr]    Compiling mime_guess v2.0.5
[INFO] [stderr]    Compiling quote v1.0.44
[INFO] [stderr]    Compiling deranged v0.5.6
[INFO] [stderr]    Compiling ahash v0.8.12
[INFO] [stderr]    Compiling regex-syntax v0.8.9
[INFO] [stderr]    Compiling syn v2.0.115
[INFO] [stderr]    Compiling num_threads v0.1.7
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling mio v1.1.1
[INFO] [stderr]    Compiling socket2 v0.6.2
[INFO] [stderr]    Compiling getrandom v0.2.17
[INFO] [stderr]    Compiling ring v0.17.14
[INFO] [stderr]    Compiling libsqlite3-sys v0.30.1
[INFO] [stderr]    Compiling time v0.3.47
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling hashbrown v0.14.5
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling http-range-header v0.4.2
[INFO] [stderr]    Compiling dirs-sys v0.5.0
[INFO] [stderr]    Compiling hashlink v0.9.1
[INFO] [stderr]    Compiling winnow v0.7.14
[INFO] [stderr]    Compiling tempfile v3.25.0
[INFO] [stderr]    Compiling dirs v6.0.0
[INFO] [stderr]    Compiling uuid v1.21.0
[INFO] [stderr]    Compiling crossbeam-channel v0.5.15
[INFO] [stderr]    Compiling yansi v1.0.1
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling unsafe-libyaml v0.2.11
[INFO] [stderr]    Compiling which v6.0.3
[INFO] [stderr]    Compiling pretty_assertions v1.4.1
[INFO] [stderr]    Compiling walkdir v2.5.0
[INFO] [stderr]    Compiling synstructure v0.13.2
[INFO] [stderr]    Compiling darling_core v0.23.0
[INFO] [stderr]    Compiling sysinfo v0.33.1
[INFO] [stderr]    Compiling rustls-webpki v0.103.9
[INFO] [stderr]    Compiling scopeguard v1.2.0
[INFO] [stderr]    Compiling glob v0.3.3
[INFO] [stderr]    Compiling lock_api v0.4.14
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling signal-hook v0.3.18
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling sha2 v0.10.9
[INFO] [stderr]    Compiling signal-hook-mio v0.2.5
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling zerofrom-derive v0.1.6
[INFO] [stderr]    Compiling yoke-derive v0.8.1
[INFO] [stderr]    Compiling zerovec-derive v0.11.2
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling displaydoc v0.2.5
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling tokio-macros v2.6.0
[INFO] [stderr]    Compiling zerofrom v0.1.6
[INFO] [stderr]    Compiling yoke v0.8.1
[INFO] [stderr]    Compiling zerovec v0.11.5
[INFO] [stderr]    Compiling futures-macro v0.3.31
[INFO] [stderr]    Compiling zerotrie v0.2.3
[INFO] [stderr]    Compiling tokio v1.49.0
[INFO] [stderr]    Compiling tinystr v0.8.2
[INFO] [stderr]    Compiling icu_locale_core v2.1.1
[INFO] [stderr]    Compiling potential_utf v0.1.4
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling icu_collections v2.1.1
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling icu_provider v2.1.1
[INFO] [stderr]    Compiling icu_normalizer v2.1.1
[INFO] [stderr]    Compiling icu_properties v2.1.2
[INFO] [stderr]    Compiling async-trait v0.1.89
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling rusqlite v0.32.1
[INFO] [stderr]    Compiling serde_urlencoded v0.7.1
[INFO] [stderr]    Compiling serde_spanned v0.6.9
[INFO] [stderr]    Compiling toml_datetime v0.6.11
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling idna_adapter v1.2.1
[INFO] [stderr]    Compiling tracing-subscriber v0.3.22
[INFO] [stderr]    Compiling idna v1.1.0
[INFO] [stderr]    Compiling toml_edit v0.22.27
[INFO] [stderr]    Compiling serde_yaml v0.9.34+deprecated
[INFO] [stderr]    Compiling url v2.5.8
[INFO] [stderr]    Compiling openjax-store v0.2.18 (/opt/rustwide/workdir/openjax-store)
[INFO] [stderr]    Compiling openjax-protocol v0.2.18 (/opt/rustwide/workdir/openjax-protocol)
[INFO] [stderr]    Compiling hyper v1.8.1
[INFO] [stderr]    Compiling tower v0.5.3
[INFO] [stderr]    Compiling tokio-util v0.7.18
[INFO] [stderr]    Compiling tokio-rustls v0.26.4
[INFO] [stderr]    Compiling tower-http v0.6.8
[INFO] [stderr]    Compiling toml v0.8.23
[INFO] [stderr]    Compiling tracing-appender v0.2.4
[INFO] [stderr]    Compiling openjax-policy v0.2.18 (/opt/rustwide/workdir/openjax-policy)
[INFO] [stderr]    Compiling hyper-util v0.1.20
[INFO] [stderr]    Compiling darling_macro v0.23.0
[INFO] [stderr]    Compiling axum-core v0.4.5
[INFO] [stderr]    Compiling strum_macros v0.26.4
[INFO] [stderr]    Compiling darling v0.23.0
[INFO] [stderr]    Compiling async-stream-impl v0.3.6
[INFO] [stderr]    Compiling axum-macros v0.4.2
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling itertools v0.13.0
[INFO] [stderr]    Compiling serde_path_to_error v0.1.20
[INFO] [stderr]    Compiling anstream v0.6.21
[INFO] [stderr]    Compiling instability v0.3.11
[INFO] [stderr]    Compiling hyper-rustls v0.27.7
[INFO] [stderr]    Compiling compact_str v0.8.1
[INFO] [stderr]    Compiling derive_more-impl v2.1.1
[INFO] [stderr]    Compiling reqwest v0.12.28
[INFO] [stderr]    Compiling async-stream v0.3.6
[INFO] [stderr]    Compiling axum v0.7.9
[INFO] [stderr]    Compiling unicode-truncate v1.1.0
[INFO] [stderr]    Compiling crossterm v0.28.1
[INFO] [stderr]    Compiling strum v0.26.3
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling simd-adler32 v0.3.9
[INFO] [stderr]    Compiling derive_more v2.1.1
[INFO] [stderr]    Compiling clap_builder v4.5.60
[INFO] [stderr]    Compiling clap_derive v4.5.55
[INFO] [stderr]    Compiling xattr v1.6.1
[INFO] [stderr]    Compiling miniz_oxide v0.8.9
[INFO] [stderr]    Compiling filetime v0.2.27
[INFO] [stderr]    Compiling ratatui v0.29.0
[INFO] [stderr]    Compiling flate2 v1.1.9
[INFO] [stderr]    Compiling tar v0.4.45
[INFO] [stderr]    Compiling openjax-core v0.2.18 (/opt/rustwide/workdir/openjax-core)
[INFO] [stderr]    Compiling clap v4.5.60
[INFO] [stderr]    Compiling openjax-cli v0.2.18 (/opt/rustwide/workdir/openjax-cli)
[INFO] [stderr]    Compiling openjax-gateway v0.2.18 (/opt/rustwide/workdir/openjax-gateway)
[INFO] [stderr]    Compiling tui_next v0.2.18 (/opt/rustwide/workdir/ui/tui)
[INFO] [stderr]    Compiling openjaxd v0.2.18 (/opt/rustwide/workdir/openjaxd)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 48s
[INFO] running `Command { std: "docker" "inspect" "dba215846e4207b6a6443039c319f806493414c7ae79bf3569de371e40fe1596", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "dba215846e4207b6a6443039c319f806493414c7ae79bf3569de371e40fe1596", kill_on_drop: false }`
[INFO] [stdout] dba215846e4207b6a6443039c319f806493414c7ae79bf3569de371e40fe1596
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 4c9876d8268ac4c58268799536f44c3c33779a2246f67aeaead9b4287ee42851
[INFO] running `Command { std: "docker" "start" "-a" "4c9876d8268ac4c58268799536f44c3c33779a2246f67aeaead9b4287ee42851", kill_on_drop: false }`
[INFO] [stderr]    Compiling tui_next v0.2.18 (/opt/rustwide/workdir/ui/tui)
[INFO] [stderr]    Compiling openjax-gateway v0.2.18 (/opt/rustwide/workdir/openjax-gateway)
[INFO] [stderr]    Compiling openjaxd v0.2.18 (/opt/rustwide/workdir/openjaxd)
[INFO] [stderr]    Compiling openjax-cli v0.2.18 (/opt/rustwide/workdir/openjax-cli)
[INFO] [stderr]    Compiling openjax-core v0.2.18 (/opt/rustwide/workdir/openjax-core)
[INFO] [stderr]    Compiling openjax-protocol v0.2.18 (/opt/rustwide/workdir/openjax-protocol)
[INFO] [stderr]    Compiling openjax-store v0.2.18 (/opt/rustwide/workdir/openjax-store)
[INFO] [stderr]    Compiling openjax-policy v0.2.18 (/opt/rustwide/workdir/openjax-policy)
[INFO] [stdout] warning: function `summarize_preview` is never used
[INFO] [stdout]    --> openjax-core/src/sandbox/runtime/mod.rs:219:15
[INFO] [stdout]     |
[INFO] [stdout] 219 | pub(crate) fn summarize_preview(text: &str, limit: usize) -> String {
[INFO] [stdout]     |               ^^^^^^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 48.74s
[INFO] running `Command { std: "docker" "inspect" "4c9876d8268ac4c58268799536f44c3c33779a2246f67aeaead9b4287ee42851", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4c9876d8268ac4c58268799536f44c3c33779a2246f67aeaead9b4287ee42851", kill_on_drop: false }`
[INFO] [stdout] 4c9876d8268ac4c58268799536f44c3c33779a2246f67aeaead9b4287ee42851
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 04c4452c07e50cdbb247cad3b1ef70f46a57d30ff1a67145de0628b293bd3287
[INFO] running `Command { std: "docker" "start" "-a" "04c4452c07e50cdbb247cad3b1ef70f46a57d30ff1a67145de0628b293bd3287", kill_on_drop: false }`
[INFO] [stderr] warning: function `summarize_preview` is never used
[INFO] [stderr]    --> openjax-core/src/sandbox/runtime/mod.rs:219:15
[INFO] [stderr]     |
[INFO] [stderr] 219 | pub(crate) fn summarize_preview(text: &str, limit: usize) -> String {
[INFO] [stderr]     |               ^^^^^^^^^^^^^^^^^
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: `openjax-core` (lib test) generated 1 warning
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.47s
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/openjax-88ae7432691ec4cb)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/openjax_core-e1fccc7a5f0f5dde)
[INFO] [stdout] 
[INFO] [stdout] running 241 tests
[INFO] [stdout] test agent::context_compressor::tests::test_existing_summary_stays_in_old_by_position ... ok
[INFO] [stdout] test agent::context_compressor::tests::test_no_split_with_four_or_fewer_turns ... ok
[INFO] [stdout] test agent::context_compressor::tests::test_format_for_prompt_includes_tool_traces ... ok
[INFO] [stdout] test agent::context_compressor::tests::test_split_with_five_turns_keeps_3_recent ... ok
[INFO] [stdout] test agent::context_compressor::tests::test_split_with_seven_turns ... ok
[INFO] [stdout] test agent::decision::tests::parser_streams_message_delta_when_action_is_final ... ok
[INFO] [stdout] test agent::decision::tests::parser_keeps_message_buffered_until_action_resolved ... ok
[INFO] [stdout] test agent::decision::tests::parser_decodes_escape_sequences_across_chunks ... ok
[INFO] [stdout] test agent::decision::tests::parses_v2_tool_batch_shape ... ok
[INFO] [stdout] test agent::loop_detector::tests::test_boundary_five_consecutive ... ok
[INFO] [stdout] test agent::loop_detector::tests::test_recovery_prompt_when_warned ... ok
[INFO] [stdout] test agent::loop_detector::tests::test_five_same_calls_trigger_warned ... ok
[INFO] [stdout] test agent::loop_detector::tests::test_normal_calls_return_none ... ok
[INFO] [stdout] test agent::loop_detector::tests::test_warned_then_same_call_halts ... ok
[INFO] [stdout] test agent::loop_detector::tests::test_warned_then_different_tool_resets ... ok
[INFO] [stdout] test agent::loop_detector::tests::test_reset_clears_state ... ok
[INFO] [stdout] test agent::decision::tests::collapses_v2_tool_batch_to_legacy_tool_action ... ok
[INFO] [stdout] test agent::policy_level::tests::from_str_returns_err_for_invalid ... ok
[INFO] [stdout] test agent::state::tests::stable_args_json_is_order_insensitive ... ok
[INFO] [stdout] test builtin_catalog::tests::all_context_windows_positive ... ok
[INFO] [stdout] test agent::policy_level::tests::to_decision_kind_maps_correctly ... ok
[INFO] [stdout] test agent::policy_level::tests::from_str_round_trips ... ok
[INFO] [stdout] test agent::bootstrap::tests::set_policy_level_strict_still_escalates_destructive ... ok
[INFO] [stdout] test agent::bootstrap::tests::set_policy_level_from_none_creates_runtime ... ok
[INFO] [stdout] test agent::bootstrap::tests::set_policy_level_from_existing_runtime_updates_default ... ok
[INFO] [stdout] test builtin_catalog::tests::catalog_is_non_empty ... ok
[INFO] [stdout] test builtin_catalog::tests::each_provider_has_default_model_in_list ... ok
[INFO] [stdout] test dispatcher::probe::tests::action_hint_prefers_tool_call_branch ... ok
[INFO] [stdout] test dispatcher::probe::tests::defaults_to_text_branch_without_tool_signal ... ok
[INFO] [stdout] test config::tests::parses_model_request_profile_from_toml ... ok
[INFO] [stdout] test dispatcher::state_machine::tests::state_machine_reaches_completed_from_locked_text ... ok
[INFO] [stdout] test dispatcher::tests::route_model_output_returns_final_for_valid_final_action ... ok
[INFO] [stdout] test dispatcher::state_machine::tests::state_machine_rejects_invalid_complete_transition ... ok
[INFO] [stdout] test dispatcher::tests::route_model_output_prefers_tool_batch_when_valid ... ok
[INFO] [stdout] test dispatcher::tests::route_model_output_returns_repair_on_unparseable_output ... ok
[INFO] [stdout] test model::anthropic_messages::tests::build_request_preserves_assistant_reasoning_blocks ... ok
[INFO] [stdout] test config::tests::write_template_does_not_overwrite_existing_file ... ok
[INFO] [stdout] test config::tests::write_template_creates_file_and_is_parseable ... ok
[INFO] [stdout] test model::anthropic_messages::tests::extract_delta_supports_content_block_delta ... ok
[INFO] [stdout] test model::anthropic_messages::tests::extract_content_blocks_includes_tool_use ... ok
[INFO] [stdout] test config::tests::find_or_create_uses_only_user_root_config_file ... ok
[INFO] [stdout] test model::anthropic_messages::tests::extract_content_supports_text_blocks ... ok
[INFO] [stdout] test model::anthropic_messages::tests::extract_thinking_supports_thinking_blocks ... ok
[INFO] [stdout] test model::anthropic_messages::tests::default_profile_keeps_anthropic_request_shape ... ok
[INFO] [stdout] test model::anthropic_messages::tests::ignores_non_message_stop_frames_for_completion_signal ... ok
[INFO] [stdout] test model::anthropic_messages::tests::extract_delta_thinking_supports_content_block_delta ... ok
[INFO] [stdout] test model::anthropic_messages::tests::detects_message_stop_frame_as_completion_signal ... ok
[INFO] [stdout] test model::anthropic_messages::tests::build_messages_endpoint_supports_base_with_or_without_v1 ... ok
[INFO] [stdout] test model::anthropic_messages::tests::parse_sse_data_line_ignores_non_data_lines ... ok
[INFO] [stdout] test model::chat_completions::streaming_usage_tests::test_usage_extracted_from_last_sse_frame ... ok
[INFO] [stdout] test model::chat_completions::streaming_usage_tests::test_usage_extracted_from_glm_format ... ok
[INFO] [stdout] test model::chat_completions::tests::extract_content_blocks_includes_tool_calls ... ok
[INFO] [stdout] test model::chat_completions::tests::extract_content_blocks_text_only ... ok
[INFO] [stdout] test model::anthropic_messages::tests::unknown_registered_model_profile_returns_clear_error ... ok
[INFO] [stdout] test model::chat_completions::tests::default_profile_keeps_stream_options_enabled ... ok
[INFO] [stdout] test model::chat_completions::tests::extract_delta_supports_block_array ... ok
[INFO] [stdout] test model::chat_completions::tests::extract_content_supports_block_array ... ok
[INFO] [stdout] test model::chat_completions::tests::extract_content_blocks_text_and_tool_calls ... ok
[INFO] [stdout] test model::chat_completions::tests::kimi_profile_sets_cli_user_agent_header ... ok
[INFO] [stdout] test model::chat_completions::tests::unknown_registered_model_profile_returns_clear_error ... ok
[INFO] [stdout] test model::chat_completions::tests::parse_sse_data_line_ignores_non_data_lines ... ok
[INFO] [stdout] test model::chat_completions::tests::kimi_profile_adds_max_tokens_and_disables_stream_options ... ok
[INFO] [stdout] test model::chat_completions::tests::extract_delta_supports_mixed_content_and_reasoning ... ok
[INFO] [stdout] test model::chat_completions::tests::extract_delta_reasoning_supports_reasoning_content ... ok
[INFO] [stdout] test model::factory::tests::build_model_client_respects_echo_backend ... ok
[INFO] [stdout] test model::factory::tests::build_model_client_respects_new_registry_config ... ok
[INFO] [stdout] test model::factory::tests::build_model_client_returns_missing_config_client_when_no_provider_available ... ok
[INFO] [stdout] test model::factory::tests::build_model_client_still_supports_legacy_openai_config ... ok
[INFO] [stdout] test model::registry::tests::api_key_env_takes_precedence_over_api_key ... ok
[INFO] [stdout] test model::factory::tests::build_model_client_reports_invalid_request_profile ... ok
[INFO] [stdout] test model::registry::tests::bridges_legacy_single_model_config ... ok
[INFO] [stdout] test model::registry::tests::infers_kimi_request_profile_when_missing ... ok
[INFO] [stdout] test model::registry::tests::infers_anthropic_request_profile_when_missing ... ok
[INFO] [stdout] test model::registry::tests::parses_new_config_with_routing ... ok
[INFO] [stdout] test model::registry::tests::resolves_api_key_from_api_key_env ... ok
[INFO] [stdout] test model::request_profiles::anthropic_messages::tests::default_profile_parses_anthropic_default_alias ... ok
[INFO] [stdout] test model::request_profiles::anthropic_messages::tests::default_profile_parses_explicit_name ... ok
[INFO] [stdout] test model::request_profiles::chat_completions::tests::default_profile_keeps_request_optional_max_tokens ... ok
[INFO] [stdout] test model::request_profiles::chat_completions::tests::kimi_profile_disables_stream_options ... ok
[INFO] [stdout] test model::request_profiles::chat_completions::tests::kimi_profile_supplies_default_max_tokens ... ok
[INFO] [stdout] test model::request_profiles::chat_completions::tests::kimi_profile_preserves_explicit_max_tokens ... ok
[INFO] [stdout] test model::request_profiles::tests::anthropic_profile_defaults_when_missing ... ok
[INFO] [stdout] test model::request_profiles::chat_completions::tests::kimi_profile_user_agent_is_none ... ok
[INFO] [stdout] test model::request_profiles::tests::anthropic_profile_rejects_unknown_profile ... ok
[INFO] [stdout] test model::request_profiles::tests::chat_profile_defaults_when_missing ... ok
[INFO] [stdout] test model::request_profiles::tests::anthropic_profile_supports_anthropic_default ... ok
[INFO] [stdout] test model::request_profiles::tests::chat_profile_rejects_unknown_profile ... ok
[INFO] [stdout] test model::router::tests::complete_does_not_fallback_when_primary_fails ... ok
[INFO] [stdout] test model::router::tests::complete_stream_does_not_fallback_when_primary_fails ... ok
[INFO] [stdout] test model::types::tests::assistant_history_blocks_prepend_reasoning ... ok
[INFO] [stdout] test model::request_profiles::tests::chat_profile_supports_kimi_coding_v1 ... ok
[INFO] [stdout] test model::types::tests::conversation_message_serde_roundtrip ... ok
[INFO] [stdout] test model::types::tests::assistant_reasoning_block_roundtrips ... ok
[INFO] [stdout] test model::types::tests::model_request_for_stage_wraps_as_user_text ... ok
[INFO] [stdout] test model::types::tests::model_response_tool_uses_filters_correctly ... ok
[INFO] [stdout] test model::types::tests::stop_reason_from_api_str ... ok
[INFO] [stdout] test model::types::tests::model_response_text_extracts_only_text_blocks ... ok
[INFO] [stdout] test sandbox::classifier::tests::identifies_process_observe_commands ... ok
[INFO] [stdout] test sandbox::policy::tests::detect_capabilities_git_commit ... ok
[INFO] [stdout] test paths::tests::builds_expected_layout_from_home_dir ... ok
[INFO] [stdout] test sandbox::policy::tests::detect_capabilities_network_command ... ok
[INFO] [stdout] test sandbox::policy::tests::non_mutating_tool_is_allowed ... ok
[INFO] [stdout] test sandbox::policy::tests::extract_shell_risk_tags_returns_tags_not_decisions ... ok
[INFO] [stdout] test sandbox::policy::tests::mutating_tool_requires_ask_approval ... ok
[INFO] [stdout] test sandbox::result::tests::classifies_zero_exit_with_fatal_stderr_as_failure ... ok
[INFO] [stdout] test provider_store::tests::kimi_provider_infers_anthropic_profile_when_using_anthropic_messages_protocol ... ok
[INFO] [stdout] test sandbox::result::tests::detects_fatal_stderr ... ok
[INFO] [stdout] test provider_store::tests::non_kimi_provider_leaves_request_profile_empty ... ok
[INFO] [stdout] test provider_store::tests::anthropic_provider_with_anthropic_messages_protocol_infers_anthropic_profile ... ok
[INFO] [stdout] test paths::tests::ensure_runtime_dirs_creates_root_logs_and_skills ... ok
[INFO] [stdout] test provider_store::tests::unknown_protocol_leaves_request_profile_empty ... ok
[INFO] [stdout] test sandbox::runtime::tests::allows_absolute_chdir_when_target_is_current_workspace ... ok
[INFO] [stdout] test sandbox::tests::process_observe_fallback_failure_stays_runtime_denied ... ok
[INFO] [stdout] test sandbox::result::tests::classifies_sigpipe_with_output_as_partial_success ... ok
[INFO] [stdout] test sandbox::runtime::tests::sh_runner_does_not_inject_pipefail_with_devnull_redirection ... ok
[INFO] [stdout] test sandbox::tests::zero_exit_with_fatal_stderr_is_runtime_denied ... ok
[INFO] [stdout] test sandbox::runtime::tests::rejects_absolute_chdir_when_target_is_not_workspace ... ok
[INFO] [stdout] test skills::loader::tests::default_slash_command_uses_directory_name ... ok
[INFO] [stdout] test skills::loader::tests::normalize_key_collapses_symbols ... ok
[INFO] [stdout] test skills::manifest::tests::falls_back_when_frontmatter_missing ... ok
[INFO] [stdout] test skills::manifest::tests::parses_frontmatter_and_body ... ok
[INFO] [stdout] test skills::loader::tests::ignores_folders_without_skill_manifest ... ok
[INFO] [stdout] test skills::manifest::tests::returns_error_for_unclosed_frontmatter ... ok
[INFO] [stdout] test skills::manifest::tests::returns_error_for_invalid_frontmatter_yaml ... ok
[INFO] [stdout] test skills::matcher::tests::rank_prefers_name_and_description_hits ... ok
[INFO] [stdout] test skills::manifest::tests::supports_empty_body ... ok
[INFO] [stdout] test skills::prompt::tests::includes_runtime_guidance_for_selected_skills ... ok
[INFO] [stdout] test skills::prompt::tests::truncates_when_prompt_budget_is_small ... ok
[INFO] [stdout] test slash_commands::registry::tests::test_compact_is_session_action ... ok
[INFO] [stdout] test slash_commands::registry::tests::test_find_alias_question_resolves_to_help ... ok
[INFO] [stdout] test slash_commands::registry::tests::test_find_alias_cls_resolves_to_clear ... ok
[INFO] [stdout] test slash_commands::registry::tests::test_help_replaces_input_false ... ok
[INFO] [stdout] test slash_commands::registry::tests::test_find_exact_clear ... ok
[INFO] [stdout] test slash_commands::registry::tests::test_match_prefix_question_matches_help_via_alias ... ok
[INFO] [stdout] test skills::loader::tests::slash_command_conflicting_with_builtin_alias_is_ignored ... ok
[INFO] [stdout] test slash_commands::registry::tests::test_no_duplicate_cls_or_question_in_all_commands ... ok
[INFO] [stdout] test slash_commands::registry::tests::test_match_prefix_cl_matches_clear ... ok
[INFO] [stdout] test slash_commands::registry::tests::test_normalize_skill_command_name ... ok
[INFO] [stdout] test slash_commands::registry::tests::test_usage_hint_no_args_suffix ... ok
[INFO] [stdout] test skills::loader::tests::slash_command_with_leading_slash_is_normalized ... ok
[INFO] [stdout] test streaming::helpers::tests::stream_helper_drains_buffered_deltas_after_stream_finishes ... ok
[INFO] [stdout] test streaming::helpers::tests::synthetic_deltas_return_empty_for_empty_message ... ok
[INFO] [stdout] test streaming::orchestrator::tests::emits_response_error_payload ... ok
[INFO] [stdout] test streaming::helpers::tests::synthetic_deltas_split_by_chunk_size ... ok
[INFO] [stdout] test streaming::orchestrator::tests::emits_started_then_delta_then_completed ... ok
[INFO] [stdout] test streaming::helpers::tests::stream_helper_returns_error_while_preserving_seen_deltas ... ok
[INFO] [stdout] test streaming::parser::anthropic::tests::parser_flushes_trailing_data_on_finish ... ok
[INFO] [stdout] test skills::loader::tests::workspace_overrides_user_for_same_skill_name ... ok
[INFO] [stdout] test slash_commands::registry::tests::test_register_skill_command_rejects_reserved_name_or_alias ... ok
[INFO] [stdout] test streaming::parser::anthropic::tests::parser_tracks_done_marker ... ok
[INFO] [stdout] test streaming::parser::openai::tests::parser_handles_chunk_split_and_done_marker ... ok
[INFO] [stdout] test streaming::parser::openai::tests::parser_reports_missing_done_marker ... ok
[INFO] [stdout] test streaming::replay::tests::replay_rejects_out_of_window_request ... ok
[INFO] [stdout] test streaming::sink::tests::drop_newest_policy_does_not_error_when_full ... ok
[INFO] [stdout] test streaming::sink::tests::reject_producer_policy_returns_queue_full ... ok
[INFO] [stdout] test tests::duplicate_guard::aborts_after_consecutive_duplicate_skips ... ok
[INFO] [stdout] test tests::prompt_and_policy::build_system_prompt_does_not_reference_legacy_read_edit_names ... ok
[INFO] [stdout] test tests::prompt_and_policy::build_turn_messages_includes_prior_conversation_summary ... ok
[INFO] [stdout] test tests::prompt_and_policy::build_system_prompt_contains_skills_section ... ok
[INFO] [stdout] test tests::prompt_and_policy::parse_runtime_policies ... ok
[INFO] [stdout] test tests::prompt_and_policy::refresh_loop_recovery_only_updates_last_user_text ... ok
[INFO] [stdout] test tests::prompt_and_policy::build_system_prompt_contains_verification_rule ... ok
[INFO] [stdout] test tests::prompt_and_policy::resolves_turn_limits_from_config_and_env_with_precedence ... ok
[INFO] [stdout] test tests::prompt_and_policy::summarize_user_input_adds_ellipsis_when_truncated ... ok
[INFO] [stdout] test tests::prompt_and_policy::summarize_user_input_escapes_control_newlines ... ok
[INFO] [stdout] test tests::duplicate_guard::duplicate_detection_is_turn_local_when_cleared ... ok
[INFO] [stdout] test tests::prompt_and_policy::build_system_prompt_contains_read_edit_hard_rules ... ok
[INFO] [stdout] test tests::streaming::planner_only_mode_skips_final_writer_and_keeps_response_delta_events ... ok
[INFO] [stdout] test tests::streaming::native_streaming_final_response_does_not_fallback_to_complete ... ok
[INFO] [stdout] test tests::streaming::final_action_emits_response_text_delta_before_completion ... ok
[INFO] [stdout] test tests::streaming::planner_only_mode_with_stream_engine_v2_still_skips_final_writer ... ok
[INFO] [stdout] test tests::duplicate_guard::duplicate_detection_resets_after_mutation_epoch_change ... ok
[INFO] [stdout] test tests::tool_batch_approval::overflow_tool_uses_are_closed_with_failed_and_completed_events ... ok
[INFO] [stdout] test tests::tool_batch_approval::tool_batch_approval_blocked_stops_followup_scheduling_and_rounds ... ok
[INFO] [stdout] test tests::tool_batch_approval::tool_batch_approval_blocked_cancels_pending_parallel_tool ... ok
[INFO] [stdout] test tests::streaming::tool_exec_outcome_keeps_model_content_separate_from_display_output ... ok
[INFO] [stdout] test tests::streaming::planner_stream_tool_events_preserve_tool_name_across_args_delta_and_ready ... ok
[INFO] [stdout] test tests::streaming::native_tool_result_uses_model_content_not_display_output ... ok
[INFO] [stdout] test tools::grep_files::tests::parse_truncates_after_limit ... ok
[INFO] [stdout] test tools::grep_files::tests::parses_basic_results ... ok
[INFO] [stdout] test tests::streaming::tool_use_round_emits_intermediate_response_text_delta ... ok
[INFO] [stdout] test tools::grep_files::tests::run_search_handles_no_matches ... ok
[INFO] [stdout] test tools::grep_files::tests::run_search_respects_limit ... ok
[INFO] [stdout] test tools::grep_files::tests::run_search_with_glob_filter ... ok
[INFO] [stdout] test tools::grep_files::tests::run_search_returns_results ... ok
[INFO] [stdout] test tools::handlers::de_helpers::tests::de_bool_accepts_json_bool ... ok
[INFO] [stdout] test tools::handlers::de_helpers::tests::de_bool_accepts_json_integer_one_as_true ... ok
[INFO] [stdout] test tools::handlers::de_helpers::tests::de_bool_accepts_json_integer_zero_as_false ... ok
[INFO] [stdout] test tools::handlers::de_helpers::tests::de_bool_accepts_string_false ... ok
[INFO] [stdout] test tools::handlers::de_helpers::tests::de_bool_accepts_string_true ... ok
[INFO] [stdout] test tools::handlers::de_helpers::tests::de_opt_usize_absent_field_is_none ... ok
[INFO] [stdout] test tools::handlers::de_helpers::tests::de_opt_usize_accepts_json_number ... ok
[INFO] [stdout] test tests::streaming::tool_use_followup_request_preserves_assistant_reasoning_history ... ok
[INFO] [stdout] test tools::handlers::de_helpers::tests::de_opt_usize_accepts_null ... ok
[INFO] [stdout] test tools::handlers::de_helpers::tests::de_opt_usize_accepts_string_number ... ok
[INFO] [stdout] test tools::handlers::de_helpers::tests::de_u64_accepts_json_number ... ok
[INFO] [stdout] test tools::handlers::de_helpers::tests::de_u64_accepts_string_number ... ok
[INFO] [stdout] test tools::handlers::de_helpers::tests::de_u64_rejects_empty_string ... ok
[INFO] [stdout] test tools::handlers::de_helpers::tests::de_u64_rejects_negative_string ... ok
[INFO] [stdout] test tools::handlers::de_helpers::tests::de_u64_rejects_non_numeric_string ... ok
[INFO] [stdout] test tools::handlers::de_helpers::tests::de_usize_accepts_json_number ... ok
[INFO] [stdout] test tools::handlers::de_helpers::tests::de_usize_accepts_string_number ... ok
[INFO] [stdout] test tools::handlers::de_helpers::tests::de_usize_rejects_empty_string ... ok
[INFO] [stdout] test tools::handlers::de_helpers::tests::de_usize_rejects_negative_string ... ok
[INFO] [stdout] test tools::handlers::de_helpers::tests::de_usize_rejects_non_numeric_string ... ok
[INFO] [stdout] test tools::handlers::shell::tests::detects_skill_trigger_like_slash_command ... ok
[INFO] [stdout] test tools::list_dir::tests::empty_directory_returns_empty_marker ... ok
[INFO] [stdout] test tools::list_dir::tests::handles_large_limit_without_overflow ... ok
[INFO] [stdout] test tests::tool_batch_approval::tool_batch_dependency_unmet_still_emits_started_before_completed ... ok
[INFO] [stdout] test tools::list_dir::tests::lists_directory_entries ... ok
[INFO] [stdout] test tests::tool_batch_approval::tool_batch_emits_proposal_and_batch_completed_events ... ok
[INFO] [stdout] test tools::list_dir::tests::indicates_truncated_results ... ok
[INFO] [stdout] test tests::tool_batch_approval::tool_call_completed_event_contains_structured_shell_metadata ... ok
[INFO] [stdout] test tools::list_dir::tests::paginates_in_sorted_order ... ok
[INFO] [stdout] test tools::list_dir::tests::truncation_respects_sorted_order ... ok
[INFO] [stdout] test tools::list_dir::tests::respects_depth_parameter ... ok
[INFO] [stdout] test tools::router::tests::parse_tool_call_preserves_quoted_shell_command ... ok
[INFO] [stdout] test tools::registry::tests::register_and_dispatch_can_run_concurrently ... ok
[INFO] [stdout] test tools::shell::tests::test_fallback_candidates_for_zsh ... ok
[INFO] [stdout] test tools::shell::tests::test_shell_derive_exec_args_with_login ... ok
[INFO] [stdout] test tools::shell::tests::test_shell_derive_exec_args_without_login ... ok
[INFO] [stdout] test tools::shell::tests::test_shell_type_executable_name ... ok
[INFO] [stdout] test tools::shell::tests::test_shell_type_login_flag ... ok
[INFO] [stdout] test tools::spec::tests::build_all_specs_exposes_read_edit_contract_names ... ok
[INFO] [stdout] test tools::system::disk_usage::tests::include_all_mounts_returns_multiple_items ... ok
[INFO] [stdout] test tools::system::disk_usage::tests::invalid_path_returns_invalid_argument ... ok
[INFO] [stdout] test tools::system::disk_usage::tests::used_percentage_is_bounded ... ok
[INFO] [stdout] test tools::system::process_snapshot::tests::limit_out_of_range_returns_invalid_argument ... ok
[INFO] [stdout] test tools::system::process_snapshot::tests::provider_permission_error_maps_to_code ... ok
[INFO] [stdout] test tools::system::process_snapshot::tests::sorts_by_cpu_then_memory ... ok
[INFO] [stdout] test tools::system::process_snapshot::tests::sorts_by_memory_then_cpu ... ok
[INFO] [stdout] test tools::system::process_snapshot::tests::user_filter_applies_to_results ... ok
[INFO] [stdout] test tools::system::provider::tests::chooses_longest_matching_mount ... ok
[INFO] [stdout] test tools::system::system_load::tests::include_cpu_false_omits_cpu_section ... ok
[INFO] [stdout] test tools::system::system_load::tests::memory_section_preserves_percent ... ok
[INFO] [stdout] test tools::tool_builder::tests::create_tool_invocation_keeps_shell_type_from_runtime_config ... ok
[INFO] [stdout] test tools::tool_builder::tests::default_registry_includes_system_tools ... ok
[INFO] [stdout] test tools::tool_builder::tests::registry_build_disables_shell_tools_when_configured ... ok
[INFO] [stdout] test tools::dynamic::tests::poisoned_lock_does_not_panic_on_list_or_register ... ok
[INFO] [stdout] test tools::list_dir::tests::errors_when_offset_exceeds_entries ... ok
[INFO] [stdout] test tests::duplicate_guard::duplicate_tool_skip_and_abort_emit_response_error_events ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 241 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.03s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/approval_events_suite.rs (/opt/rustwide/target/debug/deps/approval_events_suite-2c77f2e952c713dc)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test approval_event_contains_policy_metadata ... ok
[INFO] [stdout] test approval_event_emission_m8::emits_approval_requested_and_resolved_events ... ok
[INFO] [stdout] test approval_event_emission_m8::submit_with_sink_emits_approval_requested_before_resolution ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/approval_suite.rs (/opt/rustwide/target/debug/deps/approval_suite-75f528a4c3f02ede)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test approval_handler_m5::on_request_prompts_for_git_commit_and_rejects ... ok
[INFO] [stdout] test approval_handler_m5::policy_deny_blocks_tool_without_approval_prompt ... ok
[INFO] [stdout] test approval_handler_m5::on_request_skips_prompt_for_non_mutating_tool ... ok
[INFO] [stdout] test approval_handler_m5::always_ask_prompts_and_approved_call_succeeds ... ok
[INFO] [stdout] test approval_handler_m5::approval_timeout_is_reported_as_timeout ... ok
[INFO] [stdout] test approval_handler_m5::on_request_prompts_for_mutating_tool_and_rejects ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.13s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/core_history_suite.rs (/opt/rustwide/target/debug/deps/core_history_suite-f8cc63bd8c5a986a)
[INFO] [stdout] 
[INFO] [stdout] running 9 tests
[INFO] [stdout] test loop_detector_m8::test_diverse_calls_never_trigger ... ok
[INFO] [stdout] test loop_detector_m8::test_loop_detector_full_flow ... ok
[INFO] [stdout] test history_turn_record_m22::successful_tool_turn_increments_history ... ok
[INFO] [stdout] test history_turn_record_m22::history_truncation_does_not_panic ... ok
[INFO] [stdout] test context_compression_m11::test_split_for_compression_skips_short_history ... ok
[INFO] [stdout] test context_compression_m11::test_compact_produces_summary_plus_recent ... ok
[INFO] [stdout] test context_compression_m11::test_auto_compact_emits_context_usage_updated ... ok
[INFO] [stdout] test context_compression_m11::test_context_window_zero_skips_auto_compact ... ok
[INFO] [stdout] test history_turn_record_m22::failed_tool_call_turn_still_completes ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.51s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/policy_center_suite.rs (/opt/rustwide/target/debug/deps/policy_center_suite-428f9bc2e752a9b4)
[INFO] [stdout] 
[INFO] [stdout] running 11 tests
[INFO] [stdout] test published_runtime_rule_can_deny_known_tool ... ok
[INFO] [stdout] test destructive_shell_triggers_escalation ... ok
[INFO] [stdout] test session_overlay_rule_applies_by_session_id ... ok
[INFO] [stdout] test destructive_command_triggers_escalation_via_builtin_rule ... ok
[INFO] [stdout] test degrade_approval_has_escalation_kind ... ok
[INFO] [stdout] test safe_shell_triggers_normal_approval ... ok
[INFO] [stdout] test degrade_approval_escalation_from_policy_center ... ok
[INFO] [stdout] test degrade_approval_denied_by_policy_center ... ok
[INFO] [stdout] test shell_allow_rule_skips_approval ... ok
[INFO] [stdout] test shell_tool_goes_through_policy_center ... ok
[INFO] [stdout] test unknown_tool_without_descriptor_defaults_to_ask ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/skills_suite.rs (/opt/rustwide/target/debug/deps/skills_suite-9c660453a45a0dae)
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test skills_config_toggle_m12::runtime_config_defaults_and_options_are_applied ... ok
[INFO] [stdout] test skills_config_toggle_m12::env_values_override_config_options ... ok
[INFO] [stdout] test skills_shell_trigger_guard_m20::shell_guard_blocks_skill_trigger_like_command ... ok
[INFO] [stdout] test skills_discovery_m10::discovers_skills_only_from_openjax_user_root ... ok
[INFO] [stdout] test skills_duplicate_resolution_m13::duplicate_skills_follow_first_directory_wins_within_user_root ... ok
[INFO] [stdout] test skills_no_shell_trigger_m18::skills_context_contains_non_shell_trigger_rule ... ok
[INFO] [stdout] test skills_prompt_injection_m11::selected_skills_render_prompt_context_with_name_description_path ... ok
[INFO] [stdout] test skills_lightweight_commit_flow_m19::skills_context_includes_lightweight_commit_best_practice ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/streaming_suite.rs (/opt/rustwide/target/debug/deps/streaming_suite-94b41cdaf7b0cf79)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test assistant_message_decommission_guardrails_m23::send_to_agent_emits_response_completed_event ... ok
[INFO] [stdout] test tool_streaming_events_m21::approval_rejection_emits_tool_call_failed ... ok
[INFO] [stdout] test submit_stream_m6::submit_with_sink_emits_events_in_same_order_as_submit_result ... ok
[INFO] [stdout] test backward_compat_submit_m7::submit_still_returns_full_turn_event_sequence ... ok
[INFO] [stdout] test tool_streaming_events_m21::emits_args_delta_and_progress_before_completion ... ok
[INFO] [stderr]      Running tests/tools_sandbox_suite.rs (/opt/rustwide/target/debug/deps/tools_sandbox_suite-6f0ac48a35e78155)
[INFO] [stdout] test assistant_message_decommission_guardrails_m23::placeholder_ops_do_not_require_assistant_message_for_completion ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 35 tests
[INFO] [stdout] test glob_files_m11::glob_files_returns_empty_when_nothing_matches ... ok
[INFO] [stdout] test edit_m5::edit_returns_not_unique_for_multiple_matches ... ok
[INFO] [stdout] test edit_m5::edit_returns_not_found_when_old_string_is_missing ... ok
[INFO] [stdout] test edit_m5::edit_replaces_unique_match_successfully ... ok
[INFO] [stdout] test edit_m5::edit_normalizes_newlines_before_matching ... ok
[INFO] [stdout] test sandbox_m3::blocks_network_command_in_workspace_write ... ok
[INFO] [stdout] test sandbox_m3::blocks_shell_redirect_write_in_workspace_write ... ok
[INFO] [stdout] test sandbox_m3::marks_pipeline_with_failed_segment_as_failed ... ok
[INFO] [stdout] test sandbox_m3::allows_shell_pipeline_in_workspace_write ... ok
[INFO] [stdout] test sandbox_m3::allows_safe_readonly_command_in_workspace_write ... ok
[INFO] [stdout] test string_args_compat_m12::glob_files_accepts_string_limit ... ok
[INFO] [stdout] test string_args_compat_m12::glob_files_default_limit_succeeds ... ok
[INFO] [stdout] test sandbox_m3::marks_sigpipe_pipeline_as_partial_success ... ok
[INFO] [stdout] test sandbox_m3::marks_shell_non_zero_exit_as_failed ... ok
[INFO] [stdout] test string_args_compat_m12::list_dir_accepts_default_args ... ok
[INFO] [stdout] test string_args_compat_m12::list_dir_accepts_string_numeric_args ... ok
[INFO] [stdout] test string_args_compat_m12::read_accepts_string_numeric_args ... ok
[INFO] [stdout] test string_args_compat_m12::read_default_args_succeed ... ok
[INFO] [stdout] test string_args_compat_m12::read_indentation_mode_accepts_string_numeric_args ... ok
[INFO] [stdout] test string_args_compat_m12::shell_accepts_string_timeout_ms ... ok
[INFO] [stdout] test string_args_compat_m12::shell_default_timeout_succeeds ... ok
[INFO] [stdout] test system_tools_m9::process_snapshot_dispatch_returns_json ... ok
[INFO] [stdout] test system_tools_m9::system_load_does_not_trigger_approval_under_on_request ... ok
[INFO] [stdout] test system_tools_m9::system_tools_are_registered_in_specs ... ok
[INFO] [stdout] test write_file_m10::write_file_creates_missing_parent_directories ... ok
[INFO] [stdout] test write_file_m10::write_file_creates_new_file_inside_workspace ... ok
[INFO] [stdout] test write_file_m10::write_file_overwrites_existing_file ... ok
[INFO] [stdout] test string_args_compat_m12::grep_files_accepts_string_limit ... FAILED
[INFO] [stdout] test string_args_compat_m12::grep_files_default_limit_succeeds ... FAILED
[INFO] [stdout] test glob_files_m11::glob_files_returns_matches_sorted_newest_first ... ok
[INFO] [stdout] test glob_files_m11::glob_files_rejects_workspace_escape ... ok
[INFO] [stdout] test sandbox_m3::blocks_absolute_path_read_in_workspace_write ... ok
[INFO] [stdout] test sandbox_m3::blocks_parent_traversal_read_in_workspace_write ... ok
[INFO] [stdout] test write_file_m10::write_file_rejects_workspace_escape ... ok
[INFO] [stdout] test glob_files_m11::glob_files_respects_limit ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- string_args_compat_m12::grep_files_accepts_string_limit stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'string_args_compat_m12::grep_files_accepts_string_limit' (411) panicked at openjax-core/tests/tools_sandbox/m12_string_args_compat.rs:200:10:
[INFO] [stdout] grep_files should execute: error: Internal error: failed to launch rg: No such file or directory (os error 2). Ensure ripgrep is installed and on PATH.
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error>::msg::<alloc::string::String>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.101/src/backtrace.rs:27:14
[INFO] [stdout]    1: <openjax_core::tools::router_impl::ToolRouter>::execute::{closure#0}
[INFO] [stdout]              at ./src/tools/router_impl.rs:199:21
[INFO] [stdout]    2: tools_sandbox_suite::string_args_compat_m12::grep_files_accepts_string_limit::{closure#0}
[INFO] [stdout]              at ./tests/tools_sandbox/m12_string_args_compat.rs:199:10
[INFO] [stdout]    3: <core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>> as core::future::future::Future>::poll
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/future/future.rs:133:9
[INFO] [stdout]    4: <core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>> as core::future::future::Future>::poll
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/future/future.rs:133:9
[INFO] [stdout]    5: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:753:70
[INFO] [stdout]    6: tokio::task::coop::with_budget::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/task/coop/mod.rs:167:5
[INFO] [stdout]    7: tokio::task::coop::budget::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/task/coop/mod.rs:133:5
[INFO] [stdout]    8: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:753:25
[INFO] [stdout]    9: <tokio::runtime::scheduler::current_thread::Context>::enter::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:442:19
[INFO] [stdout]   10: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:752:44
[INFO] [stdout]   11: <tokio::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:840:68
[INFO] [stdout]   12: <tokio::runtime::context::scoped::Scoped<tokio::runtime::scheduler::Context>>::set::<<tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/context/scoped.rs:40:9
[INFO] [stdout]   13: tokio::runtime::context::set_scheduler::<(alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>), <tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/context.rs:176:38
[INFO] [stdout]   14: <std::thread::local::LocalKey<tokio::runtime::context::Context>>::try_with::<tokio::runtime::context::set_scheduler<(alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>), <tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/local.rs:462:12
[INFO] [stdout]   15: <std::thread::local::LocalKey<tokio::runtime::context::Context>>::with::<tokio::runtime::context::set_scheduler<(alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>), <tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/local.rs:426:20
[INFO] [stdout]   16: tokio::runtime::context::set_scheduler::<(alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>), <tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/context.rs:176:17
[INFO] [stdout]   17: <tokio::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:840:27
[INFO] [stdout]   18: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:740:24
[INFO] [stdout]   19: <tokio::runtime::scheduler::current_thread::CurrentThread>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:200:33
[INFO] [stdout]   20: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/context/runtime.rs:65:16
[INFO] [stdout]   21: <tokio::runtime::scheduler::current_thread::CurrentThread>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:188:9
[INFO] [stdout]   22: <tokio::runtime::runtime::Runtime>::block_on_inner::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/runtime.rs:368:52
[INFO] [stdout]   23: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/runtime.rs:342:18
[INFO] [stdout]   24: tools_sandbox_suite::string_args_compat_m12::grep_files_accepts_string_limit
[INFO] [stdout]              at ./tests/tools_sandbox/m12_string_args_compat.rs:208:42
[INFO] [stdout]   25: tools_sandbox_suite::string_args_compat_m12::grep_files_accepts_string_limit::{closure#0}
[INFO] [stdout]              at ./tests/tools_sandbox/m12_string_args_compat.rs:191:43
[INFO] [stdout]   26: <tools_sandbox_suite::string_args_compat_m12::grep_files_accepts_string_limit::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   27: <fn() -> core::result::Result<(), alloc::string::String> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   28: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:724:18
[INFO] [stdout]   29: test::run_test_in_process::{closure#0}
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:74
[INFO] [stdout]   30: <core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   31: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   32: std::panicking::catch_unwind::<core::result::Result<(), alloc::string::String>, core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>>
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   33: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   34: test::run_test_in_process
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:27
[INFO] [stdout]   35: test::run_test::{closure#0}
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:668:43
[INFO] [stdout]   36: test::run_test::{closure#1}
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:698:41
[INFO] [stdout]   37: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   38: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   39: <core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   40: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   41: std::panicking::catch_unwind::<(), core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   42: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   43: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   44: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   45: <alloc::boxed::Box<dyn core::ops::function::FnOnce<(), Output = ()> + core::marker::Send> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2277:9
[INFO] [stdout]   46: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   47: <unknown>
[INFO] [stdout]   48: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x56715117e318 - std[929523f510218772]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x56715117e318 - std[929523f510218772]::backtrace_rs::backtrace::trace_unsynchronized::<std[929523f510218772]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x56715117e318 - std[929523f510218772]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x56715117e318 - <<std[929523f510218772]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[28b146924c2f19c6]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x567151195a6a - <core[28b146924c2f19c6]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x567151195a6a - core[28b146924c2f19c6]::fmt::write
[INFO] [stdout]    6:     0x567151183a02 - std[929523f510218772]::io::default_write_fmt::<alloc[95e62e727ddf147d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:622:11
[INFO] [stdout]    7:     0x567151183a02 - <alloc[95e62e727ddf147d]::vec::Vec<u8> as std[929523f510218772]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:1977:13
[INFO] [stdout]    8:     0x5671511596f6 - <std[929523f510218772]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5671511596f6 - std[929523f510218772]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5671511751f9 - std[929523f510218772]::panicking::default_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x567150791cec - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   12:     0x567150791cec - test[6ec0502068880816]::test_main_inner::<test[6ec0502068880816]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x567151175472 - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   14:     0x567151175472 - std[929523f510218772]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5671511597b2 - std[929523f510218772]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x56715114e029 - std[929523f510218772]::sys::backtrace::__rust_end_short_backtrace::<std[929523f510218772]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x56715115a7fd - __rustc[acf6043c977865fb]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x56715119625c - core[28b146924c2f19c6]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x567151195fa2 - core[28b146924c2f19c6]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/result.rs:1872:5
[INFO] [stdout]   20:     0x5671507723d3 - <core[28b146924c2f19c6]::result::Result<openjax_core[430a7510a6be7011]::tools::router_impl::ToolExecOutcome, anyhow[9b0df0408a975bd1]::Error>>::expect
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/result.rs:1185:23
[INFO] [stdout]   21:     0x567150754d34 - tools_sandbox_suite[76cc033057e5b494]::string_args_compat_m12::grep_files_accepts_string_limit::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/openjax-core/tests/tools_sandbox/m12_string_args_compat.rs:200:10
[INFO] [stdout]   22:     0x567150766e32 - <core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>> as core[28b146924c2f19c6]::future::future::Future>::poll
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/future/future.rs:133:9
[INFO] [stdout]   23:     0x567150766f0d - <core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>> as core[28b146924c2f19c6]::future::future::Future>::poll
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/future/future.rs:133:9
[INFO] [stdout]   24:     0x56715073717d - <tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:753:70
[INFO] [stdout]   25:     0x56715073707b - tokio[fd8b4b45a77ccaaf]::task::coop::with_budget::<core[28b146924c2f19c6]::task::poll::Poll<()>, <tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/task/coop/mod.rs:167:5
[INFO] [stdout]   26:     0x56715073707b - tokio[fd8b4b45a77ccaaf]::task::coop::budget::<core[28b146924c2f19c6]::task::poll::Poll<()>, <tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/task/coop/mod.rs:133:5
[INFO] [stdout]   27:     0x56715073707b - <tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:753:25
[INFO] [stdout]   28:     0x567150733610 - <tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::Context>::enter::<core[28b146924c2f19c6]::task::poll::Poll<()>, <tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:442:19
[INFO] [stdout]   29:     0x56715073634d - <tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:752:44
[INFO] [stdout]   30:     0x567150735644 - <tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:840:68
[INFO] [stdout]   31:     0x56715073f28b - <tokio[fd8b4b45a77ccaaf]::runtime::context::scoped::Scoped<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::Context>>::set::<<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>::{closure#0}, (alloc[95e62e727ddf147d]::boxed::Box<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>)>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/context/scoped.rs:40:9
[INFO] [stdout]   32:     0x567150720e19 - tokio[fd8b4b45a77ccaaf]::runtime::context::set_scheduler::<(alloc[95e62e727ddf147d]::boxed::Box<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>), <tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/context.rs:176:38
[INFO] [stdout]   33:     0x56715071e4d2 - <std[929523f510218772]::thread::local::LocalKey<tokio[fd8b4b45a77ccaaf]::runtime::context::Context>>::try_with::<tokio[fd8b4b45a77ccaaf]::runtime::context::set_scheduler<(alloc[95e62e727ddf147d]::boxed::Box<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>), <tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[95e62e727ddf147d]::boxed::Box<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>)>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/local.rs:462:12
[INFO] [stdout]   34:     0x56715071e05e - <std[929523f510218772]::thread::local::LocalKey<tokio[fd8b4b45a77ccaaf]::runtime::context::Context>>::with::<tokio[fd8b4b45a77ccaaf]::runtime::context::set_scheduler<(alloc[95e62e727ddf147d]::boxed::Box<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>), <tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[95e62e727ddf147d]::boxed::Box<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>)>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/local.rs:426:20
[INFO] [stdout]   35:     0x56715071f39d - tokio[fd8b4b45a77ccaaf]::runtime::context::set_scheduler::<(alloc[95e62e727ddf147d]::boxed::Box<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>), <tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/context.rs:176:17
[INFO] [stdout]   36:     0x5671507346b0 - <tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:840:27
[INFO] [stdout]   37:     0x5671507349a6 - <tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:740:24
[INFO] [stdout]   38:     0x567150735250 - <tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CurrentThread>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:200:33
[INFO] [stdout]   39:     0x56715072a103 - tokio[fd8b4b45a77ccaaf]::runtime::context::runtime::enter_runtime::<<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/context/runtime.rs:65:16
[INFO] [stdout]   40:     0x567150733141 - <tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CurrentThread>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:188:9
[INFO] [stdout]   41:     0x56715073ee14 - <tokio[fd8b4b45a77ccaaf]::runtime::runtime::Runtime>::block_on_inner::<core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/runtime.rs:368:52
[INFO] [stdout]   42:     0x56715073efdf - <tokio[fd8b4b45a77ccaaf]::runtime::runtime::Runtime>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/runtime.rs:342:18
[INFO] [stdout]   43:     0x567150757fb3 - tools_sandbox_suite[76cc033057e5b494]::string_args_compat_m12::grep_files_accepts_string_limit
[INFO] [stdout]                                at /opt/rustwide/workdir/openjax-core/tests/tools_sandbox/m12_string_args_compat.rs:208:42
[INFO] [stdout]   44:     0x5671507533b7 - tools_sandbox_suite[76cc033057e5b494]::string_args_compat_m12::grep_files_accepts_string_limit::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/openjax-core/tests/tools_sandbox/m12_string_args_compat.rs:191:43
[INFO] [stdout]   45:     0x567150784956 - <tools_sandbox_suite[76cc033057e5b494]::string_args_compat_m12::grep_files_accepts_string_limit::{closure#0} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   46:     0x567150784dcb - <fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   47:     0x567150784dcb - test[6ec0502068880816]::__rust_begin_short_backtrace::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:724:18
[INFO] [stdout]   48:     0x567150792645 - test[6ec0502068880816]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:74
[INFO] [stdout]   49:     0x567150792645 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   50:     0x567150792645 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   51:     0x567150792645 - std[929523f510218772]::panicking::catch_unwind::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   52:     0x567150792645 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   53:     0x567150792645 - test[6ec0502068880816]::run_test_in_process
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:27
[INFO] [stdout]   54:     0x567150792645 - test[6ec0502068880816]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:668:43
[INFO] [stdout]   55:     0x56715078d1a4 - test[6ec0502068880816]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:698:41
[INFO] [stdout]   56:     0x56715078d1a4 - std[929523f510218772]::sys::backtrace::__rust_begin_short_backtrace::<test[6ec0502068880816]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   57:     0x5671507956c2 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   58:     0x5671507956c2 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   59:     0x5671507956c2 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   60:     0x5671507956c2 - std[929523f510218772]::panicking::catch_unwind::<(), core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   61:     0x5671507956c2 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   62:     0x5671507956c2 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   63:     0x5671507956c2 - <std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   64:     0x56715117d56f - <alloc[95e62e727ddf147d]::boxed::Box<dyn core[28b146924c2f19c6]::ops::function::FnOnce<(), Output = ()> + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2277:9
[INFO] [stdout]   65:     0x56715117d56f - <std[929523f510218772]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   66:     0x725bd7086aa4 - <unknown>
[INFO] [stdout]   67:     0x725bd7113a64 - clone
[INFO] [stdout]   68:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- string_args_compat_m12::grep_files_default_limit_succeeds stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'string_args_compat_m12::grep_files_default_limit_succeeds' (416) panicked at openjax-core/tests/tools_sandbox/m12_string_args_compat.rs:221:10:
[INFO] [stdout] grep_files should execute: error: Internal error: failed to launch rg: No such file or directory (os error 2). Ensure ripgrep is installed and on PATH.
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error>::msg::<alloc::string::String>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.101/src/backtrace.rs:27:14
[INFO] [stdout]    1: <openjax_core::tools::router_impl::ToolRouter>::execute::{closure#0}
[INFO] [stdout]              at ./src/tools/router_impl.rs:199:21
[INFO] [stdout]    2: tools_sandbox_suite::string_args_compat_m12::grep_files_default_limit_succeeds::{closure#0}
[INFO] [stdout]              at ./tests/tools_sandbox/m12_string_args_compat.rs:220:10
[INFO] [stdout]    3: <core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>> as core::future::future::Future>::poll
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/future/future.rs:133:9
[INFO] [stdout]    4: <core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>> as core::future::future::Future>::poll
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/future/future.rs:133:9
[INFO] [stdout]    5: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:753:70
[INFO] [stdout]    6: tokio::task::coop::with_budget::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/task/coop/mod.rs:167:5
[INFO] [stdout]    7: tokio::task::coop::budget::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/task/coop/mod.rs:133:5
[INFO] [stdout]    8: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:753:25
[INFO] [stdout]    9: <tokio::runtime::scheduler::current_thread::Context>::enter::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:442:19
[INFO] [stdout]   10: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:752:44
[INFO] [stdout]   11: <tokio::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:840:68
[INFO] [stdout]   12: <tokio::runtime::context::scoped::Scoped<tokio::runtime::scheduler::Context>>::set::<<tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/context/scoped.rs:40:9
[INFO] [stdout]   13: tokio::runtime::context::set_scheduler::<(alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>), <tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/context.rs:176:38
[INFO] [stdout]   14: <std::thread::local::LocalKey<tokio::runtime::context::Context>>::try_with::<tokio::runtime::context::set_scheduler<(alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>), <tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/local.rs:462:12
[INFO] [stdout]   15: <std::thread::local::LocalKey<tokio::runtime::context::Context>>::with::<tokio::runtime::context::set_scheduler<(alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>), <tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/local.rs:426:20
[INFO] [stdout]   16: tokio::runtime::context::set_scheduler::<(alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>), <tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/context.rs:176:17
[INFO] [stdout]   17: <tokio::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:840:27
[INFO] [stdout]   18: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:740:24
[INFO] [stdout]   19: <tokio::runtime::scheduler::current_thread::CurrentThread>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:200:33
[INFO] [stdout]   20: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/context/runtime.rs:65:16
[INFO] [stdout]   21: <tokio::runtime::scheduler::current_thread::CurrentThread>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:188:9
[INFO] [stdout]   22: <tokio::runtime::runtime::Runtime>::block_on_inner::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/runtime.rs:368:52
[INFO] [stdout]   23: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/runtime.rs:342:18
[INFO] [stdout]   24: tools_sandbox_suite::string_args_compat_m12::grep_files_default_limit_succeeds
[INFO] [stdout]              at ./tests/tools_sandbox/m12_string_args_compat.rs:225:42
[INFO] [stdout]   25: tools_sandbox_suite::string_args_compat_m12::grep_files_default_limit_succeeds::{closure#0}
[INFO] [stdout]              at ./tests/tools_sandbox/m12_string_args_compat.rs:212:45
[INFO] [stdout]   26: <tools_sandbox_suite::string_args_compat_m12::grep_files_default_limit_succeeds::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   27: <fn() -> core::result::Result<(), alloc::string::String> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   28: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:724:18
[INFO] [stdout]   29: test::run_test_in_process::{closure#0}
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:74
[INFO] [stdout]   30: <core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   31: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stderr] error: test failed, to rerun pass `-p openjax-core --test tools_sandbox_suite`
[INFO] [stdout]   32: std::panicking::catch_unwind::<core::result::Result<(), alloc::string::String>, core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>>
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   33: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   34: test::run_test_in_process
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:27
[INFO] [stdout]   35: test::run_test::{closure#0}
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:668:43
[INFO] [stdout]   36: test::run_test::{closure#1}
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:698:41
[INFO] [stdout]   37: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   38: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   39: <core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   40: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   41: std::panicking::catch_unwind::<(), core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   42: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   43: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   44: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   45: <alloc::boxed::Box<dyn core::ops::function::FnOnce<(), Output = ()> + core::marker::Send> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2277:9
[INFO] [stdout]   46: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   47: <unknown>
[INFO] [stdout]   48: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x56715117e318 - std[929523f510218772]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x56715117e318 - std[929523f510218772]::backtrace_rs::backtrace::trace_unsynchronized::<std[929523f510218772]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x56715117e318 - std[929523f510218772]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x56715117e318 - <<std[929523f510218772]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[28b146924c2f19c6]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x567151195a6a - <core[28b146924c2f19c6]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x567151195a6a - core[28b146924c2f19c6]::fmt::write
[INFO] [stdout]    6:     0x567151183a02 - std[929523f510218772]::io::default_write_fmt::<alloc[95e62e727ddf147d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:622:11
[INFO] [stdout]    7:     0x567151183a02 - <alloc[95e62e727ddf147d]::vec::Vec<u8> as std[929523f510218772]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:1977:13
[INFO] [stdout]    8:     0x5671511596f6 - <std[929523f510218772]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5671511596f6 - std[929523f510218772]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5671511751f9 - std[929523f510218772]::panicking::default_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x567150791cec - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   12:     0x567150791cec - test[6ec0502068880816]::test_main_inner::<test[6ec0502068880816]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x567151175472 - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   14:     0x567151175472 - std[929523f510218772]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5671511597b2 - std[929523f510218772]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x56715114e029 - std[929523f510218772]::sys::backtrace::__rust_end_short_backtrace::<std[929523f510218772]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x56715115a7fd - __rustc[acf6043c977865fb]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x56715119625c - core[28b146924c2f19c6]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x567151195fa2 - core[28b146924c2f19c6]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/result.rs:1872:5
[INFO] [stdout]   20:     0x5671507723d3 - <core[28b146924c2f19c6]::result::Result<openjax_core[430a7510a6be7011]::tools::router_impl::ToolExecOutcome, anyhow[9b0df0408a975bd1]::Error>>::expect
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/result.rs:1185:23
[INFO] [stdout]   21:     0x567150756134 - tools_sandbox_suite[76cc033057e5b494]::string_args_compat_m12::grep_files_default_limit_succeeds::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/openjax-core/tests/tools_sandbox/m12_string_args_compat.rs:221:10
[INFO] [stdout]   22:     0x567150766e32 - <core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>> as core[28b146924c2f19c6]::future::future::Future>::poll
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/future/future.rs:133:9
[INFO] [stdout]   23:     0x567150766f0d - <core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>> as core[28b146924c2f19c6]::future::future::Future>::poll
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/future/future.rs:133:9
[INFO] [stdout]   24:     0x56715073717d - <tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:753:70
[INFO] [stdout]   25:     0x56715073707b - tokio[fd8b4b45a77ccaaf]::task::coop::with_budget::<core[28b146924c2f19c6]::task::poll::Poll<()>, <tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/task/coop/mod.rs:167:5
[INFO] [stdout]   26:     0x56715073707b - tokio[fd8b4b45a77ccaaf]::task::coop::budget::<core[28b146924c2f19c6]::task::poll::Poll<()>, <tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/task/coop/mod.rs:133:5
[INFO] [stdout]   27:     0x56715073707b - <tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:753:25
[INFO] [stdout]   28:     0x567150733610 - <tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::Context>::enter::<core[28b146924c2f19c6]::task::poll::Poll<()>, <tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:442:19
[INFO] [stdout]   29:     0x56715073634d - <tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:752:44
[INFO] [stdout]   30:     0x567150735644 - <tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:840:68
[INFO] [stdout]   31:     0x56715073f28b - <tokio[fd8b4b45a77ccaaf]::runtime::context::scoped::Scoped<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::Context>>::set::<<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>::{closure#0}, (alloc[95e62e727ddf147d]::boxed::Box<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>)>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/context/scoped.rs:40:9
[INFO] [stdout]   32:     0x567150720e19 - tokio[fd8b4b45a77ccaaf]::runtime::context::set_scheduler::<(alloc[95e62e727ddf147d]::boxed::Box<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>), <tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/context.rs:176:38
[INFO] [stdout]   33:     0x56715071e4d2 - <std[929523f510218772]::thread::local::LocalKey<tokio[fd8b4b45a77ccaaf]::runtime::context::Context>>::try_with::<tokio[fd8b4b45a77ccaaf]::runtime::context::set_scheduler<(alloc[95e62e727ddf147d]::boxed::Box<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>), <tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[95e62e727ddf147d]::boxed::Box<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>)>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/local.rs:462:12
[INFO] [stdout]   34:     0x56715071e05e - <std[929523f510218772]::thread::local::LocalKey<tokio[fd8b4b45a77ccaaf]::runtime::context::Context>>::with::<tokio[fd8b4b45a77ccaaf]::runtime::context::set_scheduler<(alloc[95e62e727ddf147d]::boxed::Box<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>), <tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[95e62e727ddf147d]::boxed::Box<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>)>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/local.rs:426:20
[INFO] [stdout]   35:     0x56715071f39d - tokio[fd8b4b45a77ccaaf]::runtime::context::set_scheduler::<(alloc[95e62e727ddf147d]::boxed::Box<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>), <tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/context.rs:176:17
[INFO] [stdout]   36:     0x5671507346b0 - <tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:840:27
[INFO] [stdout]   37:     0x5671507349a6 - <tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:740:24
[INFO] [stdout]   38:     0x567150735250 - <tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CurrentThread>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:200:33
[INFO] [stdout]   39:     0x56715072a103 - tokio[fd8b4b45a77ccaaf]::runtime::context::runtime::enter_runtime::<<tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/context/runtime.rs:65:16
[INFO] [stdout]   40:     0x567150733141 - <tokio[fd8b4b45a77ccaaf]::runtime::scheduler::current_thread::CurrentThread>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:188:9
[INFO] [stdout]   41:     0x56715073ee14 - <tokio[fd8b4b45a77ccaaf]::runtime::runtime::Runtime>::block_on_inner::<core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/runtime.rs:368:52
[INFO] [stdout]   42:     0x56715073efdf - <tokio[fd8b4b45a77ccaaf]::runtime::runtime::Runtime>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/runtime.rs:342:18
[INFO] [stdout]   43:     0x5671507586b3 - tools_sandbox_suite[76cc033057e5b494]::string_args_compat_m12::grep_files_default_limit_succeeds
[INFO] [stdout]                                at /opt/rustwide/workdir/openjax-core/tests/tools_sandbox/m12_string_args_compat.rs:225:42
[INFO] [stdout]   44:     0x567150753477 - tools_sandbox_suite[76cc033057e5b494]::string_args_compat_m12::grep_files_default_limit_succeeds::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/openjax-core/tests/tools_sandbox/m12_string_args_compat.rs:212:45
[INFO] [stdout]   45:     0x567150784a56 - <tools_sandbox_suite[76cc033057e5b494]::string_args_compat_m12::grep_files_default_limit_succeeds::{closure#0} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   46:     0x567150784dcb - <fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   47:     0x567150784dcb - test[6ec0502068880816]::__rust_begin_short_backtrace::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:724:18
[INFO] [stdout]   48:     0x567150792645 - test[6ec0502068880816]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:74
[INFO] [stdout]   49:     0x567150792645 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   50:     0x567150792645 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   51:     0x567150792645 - std[929523f510218772]::panicking::catch_unwind::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   52:     0x567150792645 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   53:     0x567150792645 - test[6ec0502068880816]::run_test_in_process
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:27
[INFO] [stdout]   54:     0x567150792645 - test[6ec0502068880816]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:668:43
[INFO] [stdout]   55:     0x56715078d1a4 - test[6ec0502068880816]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:698:41
[INFO] [stdout]   56:     0x56715078d1a4 - std[929523f510218772]::sys::backtrace::__rust_begin_short_backtrace::<test[6ec0502068880816]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   57:     0x5671507956c2 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   58:     0x5671507956c2 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   59:     0x5671507956c2 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   60:     0x5671507956c2 - std[929523f510218772]::panicking::catch_unwind::<(), core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   61:     0x5671507956c2 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   62:     0x5671507956c2 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   63:     0x5671507956c2 - <std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   64:     0x56715117d56f - <alloc[95e62e727ddf147d]::boxed::Box<dyn core[28b146924c2f19c6]::ops::function::FnOnce<(), Output = ()> + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2277:9
[INFO] [stdout]   65:     0x56715117d56f - <std[929523f510218772]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   66:     0x725bd7086aa4 - <unknown>
[INFO] [stdout]   67:     0x725bd7113a64 - clone
[INFO] [stdout]   68:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     string_args_compat_m12::grep_files_accepts_string_limit
[INFO] [stdout]     string_args_compat_m12::grep_files_default_limit_succeeds
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 33 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.41s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "04c4452c07e50cdbb247cad3b1ef70f46a57d30ff1a67145de0628b293bd3287", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "04c4452c07e50cdbb247cad3b1ef70f46a57d30ff1a67145de0628b293bd3287", kill_on_drop: false }`
[INFO] [stdout] 04c4452c07e50cdbb247cad3b1ef70f46a57d30ff1a67145de0628b293bd3287
